**** dT 0.000 * top TEST ../../../../bin/vinyltest/tests/b00053.vtc starting **** top extmacro def pkg_version=trunk **** top extmacro def pkg_branch=trunk **** top extmacro def pwd=/tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest **** top extmacro def date(...) **** top extmacro def string(...) **** top extmacro def localhost=127.0.0.1 **** top extmacro def bad_backend=127.0.0.1:47618 **** top extmacro def listen_addr=127.0.0.1:0 **** top extmacro def bad_ip=192.0.2.255 **** top extmacro def topbuild=/tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub **** top extmacro def topsrc=/tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/../.. **** top macro def testdir=/tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/../../../../bin/vinyltest/tests **** top macro def tmpdir=/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667 **** top macro def vtcid=vtc.890877.3d50f667 ** top === vtest "Does anything get through Unix domain sockets at all ... **** dT 0.001 * top VTEST Does anything get through Unix domain sockets at all ? ** top === server s1 -listen "${tmpdir}/s1.sock" { ** s1 Starting server **** s1 macro def s1_addr=0.0.0.0 **** s1 macro def s1_port=0 **** s1 macro def s1_sock=/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/s1.sock * s1 Listen on /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/s1.sock ** top === vinyl v1 -arg "-a ${tmpdir}/v1.sock -a ${listen_addr}" -vcl+... ** s1 Started on /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/s1.sock (1 iterations) **** dT 0.012 ** v1 Launch *** v1 CMD: cd ${pwd} && exec vinyld -d -n /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1 -i v1 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p thread_pool_min=10 -p debug=+vtc_mode -p vsl_mask=+Debug,+H2RxHdr,+H2RxBody -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -M '127.0.0.1 36964' -P /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1/vinyld.pid -p vmod_path=/tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/vmod/.libs: -a /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock -a 127.0.0.1:0 *** v1 CMD: cd /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest && exec vinyld -d -n /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1 -i v1 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p thread_pool_min=10 -p debug=+vtc_mode -p vsl_mask=+Debug,+H2RxHdr,+H2RxBody -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -M '127.0.0.1 36964' -P /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1/vinyld.pid -p vmod_path=/tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/vmod/.libs: -a /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock -a 127.0.0.1:0 **** dT 0.015 *** v1 PID: 890894 **** v1 macro def v1_pid=890894 **** v1 macro def v1_name=/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1 **** dT 0.053 *** v1 debug|Warning: mlock() of VSM failed: Not owner (1) **** dT 0.056 *** v1 debug|Debug: Version: vinyl-cache-trunk revision 17ea9216e8091e8bd4b65a2e0b946c92de2125f4 *** v1 debug| *** v1 debug|Debug: *** v1 debug| *** v1 debug|Platform: -jsolaris,-sdefault,-sdefault,-hcritbit *** v1 debug| *** v1 debug|200 292 *** v1 debug|----------------------------- *** v1 debug|Vinyl Cache CLI 1.0 *** v1 debug|----------------------------- *** v1 debug|-jsolaris,-sdefault,-sdefault,-hcritbit *** v1 debug|vinyl-cache-trunk revision 17ea9216e8091e8bd4b65a2e0b946c92de2125f4 *** v1 debug| *** v1 debug|Type 'help' for command list. *** v1 debug|Type 'quit' to close CLI session. *** v1 debug|Type 'start' to launch worker process. *** v1 debug| **** dT 0.154 **** v1 CLIPOLL 1 0x1 0x0 0x0 *** v1 CLI connection fd = 6 *** v1 CLI RX 107 **** v1 CLI RX|sigxgjfmvyxcabhrwcitftsylpmwtvad **** v1 CLI RX| **** v1 CLI RX|Authentication required. **** v1 CLI TX|auth 8c208578324387d172c7498f4569dd62eacfe0b337abb3f950244a1b62db0945 **** dT 0.155 *** v1 CLI RX 200 **** v1 CLI RX|----------------------------- **** v1 CLI RX|Vinyl Cache CLI 1.0 **** v1 CLI RX|----------------------------- **** v1 CLI RX|-jsolaris,-sdefault,-sdefault,-hcritbit **** v1 CLI RX|vinyl-cache-trunk revision 17ea9216e8091e8bd4b65a2e0b946c92de2125f4 **** v1 CLI RX| **** v1 CLI RX|Type 'help' for command list. **** v1 CLI RX|Type 'quit' to close CLI session. **** v1 CLI RX|Type 'start' to launch worker process. **** dT 0.156 **** v1 CLI TX|vcl.inline vcl1 << %XJEIFLH|)Xspa8P **** v1 CLI TX|vcl 4.1; **** v1 CLI TX|backend s1 { .path = "/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/s1.sock"; } **** v1 CLI TX| **** v1 CLI TX| **** v1 CLI TX|\tsub vcl_backend_response { **** v1 CLI TX|\t\tset beresp.do_stream = false; **** v1 CLI TX|\t} **** v1 CLI TX|\tsub vcl_deliver { **** v1 CLI TX|\t\t# make s_resp_hdrbytes deterministic **** v1 CLI TX|\t\tunset resp.http.via; **** v1 CLI TX|\t} **** v1 CLI TX| **** v1 CLI TX|%XJEIFLH|)Xspa8P **** dT 0.256 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.356 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.456 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.556 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.638 *** v1 CLI RX 200 **** v1 CLI RX|Message from VCC-compiler: **** v1 CLI RX|Security: proc_setid missing, uid/gid unchanged **** v1 CLI RX|Message from C-compiler: **** v1 CLI RX|Security: proc_setid missing, uid/gid unchanged **** v1 CLI RX|cc: Warning: Option -fno-var-tracking-assignments passed to ld, if ld is invoked, ignored otherwise **** v1 CLI RX|"vgc.c", line 3176: warning: statement not reached (E_STATEMENT_NOT_REACHED) **** v1 CLI RX|Message from dlopen: **** v1 CLI RX|Security: proc_setid missing, uid/gid unchanged **** v1 CLI RX|VCL compiled. **** v1 CLI TX|vcl.use vcl1 *** v1 CLI RX 200 **** v1 CLI RX|VCL 'vcl1' now active ** v1 Start **** v1 CLI TX|start **** dT 0.654 *** v1 debug|Debug: Child (890946) Started *** v1 debug| **** dT 0.656 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.686 *** v1 debug|Child launched OK **** dT 0.688 *** v1 CLI RX 200 *** v1 debug|Info: Child (890946) said Child starts *** v1 wait-running **** v1 CLI TX|status *** v1 debug|Info: Child (890946) said notice:\tlibumem was already found to be loaded *** v1 debug|Info: *** v1 debug| Child (890946) said Security: proc_setid missing, uid/gid unchanged *** v1 CLI RX 200 **** v1 CLI RX|Child in state running **** v1 CLI TX|debug.listen_address *** v1 CLI RX 200 **** v1 CLI RX|a0 /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock - **** v1 CLI RX|a1 127.0.0.1 35917 **** v1 CLI TX|debug.xid 1000 *** v1 CLI RX 200 **** v1 CLI RX|XID is 1000 chunk 1 **** v1 CLI TX|debug.listen_address **** dT 0.689 *** v1 CLI RX 200 **** v1 CLI RX|a0 /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock - **** v1 CLI RX|a1 127.0.0.1 35917 ** v1 Listen on 0.0.0.0 0 **** v1 macro def v1_addr=0.0.0.0 **** v1 macro def v1_port=0 **** v1 macro def v1_sock=/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock **** v1 macro def v1_a0_addr=0.0.0.0 **** v1 macro def v1_a0_port=0 **** v1 macro def v1_a0_sock=/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock **** v1 macro def v1_a1_addr=127.0.0.1 **** v1 macro def v1_a1_port=35917 **** v1 macro def v1_a1_sock=127.0.0.1:35917 **** dT 0.756 **** v1 vsl| 0 CLI - Rd vcl.load "vcl1" vcl_vcl1.1773746796.376649/vgc.so 1auto **** v1 vsl| 0 CLI - Wr 200 52 Loaded "vcl_vcl1.1773746796.376649/vgc.so" as "vcl1" **** v1 vsl| 0 CLI - Rd vcl.use "vcl1" **** v1 vsl| 0 CLI - Wr 200 0 **** v1 vsl| 0 CLI - Rd start **** v1 vsl| 0 Debug - sockopt: Setting SO_LINGER for a1=127.0.0.1:35917 **** v1 vsl| 0 Debug - sockopt: Setting SO_KEEPALIVE for a1=127.0.0.1:35917 **** v1 vsl| 0 Debug - sockopt: Setting SO_SNDTIMEO for a1=127.0.0.1:35917 **** v1 vsl| 0 Debug - sockopt: Setting SO_RCVTIMEO for a1=127.0.0.1:35917 **** v1 vsl| 0 Debug - sockopt: Setting TCP_NODELAY for a1=127.0.0.1:35917 **** v1 vsl| 0 Debug - sockopt: Setting TCP_KEEPIDLE for a1=127.0.0.1:35917 **** v1 vsl| 0 Debug - sockopt: Setting TCP_KEEPCNT for a1=127.0.0.1:35917 **** v1 vsl| 0 Debug - sockopt: Setting TCP_KEEPINTVL for a1=127.0.0.1:35917 **** v1 vsl| 0 Debug - sockopt: Setting SO_LINGER for a0=/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock **** v1 vsl| 0 Debug - sockopt: Setting SO_KEEPALIVE for a0=/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock **** v1 vsl| 0 Debug - sockopt: Setting SO_SNDTIMEO for a0=/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock **** v1 vsl| 0 Debug - sockopt: Setting SO_RCVTIMEO for a0=/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock **** v1 vsl| 0 CLI - Wr 200 0 **** v1 vsl| 0 CLI - Rd debug.listen_address **** v1 vsl| 0 CLI - Wr 200 99 a0 /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock - a1 127.0.0.1 35917 **** v1 vsl| 0 CLI - Rd debug.xid 1000 **** v1 vsl| 0 CLI - Wr 200 19 XID is 1000 chunk 1 **** v1 vsl| 0 CLI - Rd debug.listen_address **** v1 vsl| 0 CLI - Wr 200 99 a0 /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock - a1 127.0.0.1 35917 **** dT 0.789 ** top === vinyl v1 -cliok "param.set debug +workspace" **** v1 CLI TX|param.set debug +workspace *** v1 CLI RX 200 ** v1 CLI 200 ** top === vinyl v1 -cliok "param.set debug +witness" **** v1 CLI TX|param.set debug +witness *** v1 CLI RX 200 ** v1 CLI 200 ** top === vinyl v1 -expect n_object == 0 **** dT 0.791 ** v1 as expected: n_object (0) == 0 (0) ** top === vinyl v1 -expect sess_conn == 0 ** v1 as expected: sess_conn (0) == 0 (0) ** top === vinyl v1 -expect client_req == 0 ** v1 as expected: client_req (0) == 0 (0) ** top === vinyl v1 -expect cache_miss == 0 ** v1 as expected: cache_miss (0) == 0 (0) ** top === client c1 -connect "${v1_sock}" { ** c1 Starting client ** c1 Waiting for client ** c1 Started on /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock (1 iterations) *** c1 Connect to /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock **** dT 0.792 *** c1 connected fd 18 to /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock ** c1 === txreq -url "/" **** c1 txreq|GET / HTTP/1.1\r **** c1 txreq|Host: 127.0.0.1\r **** c1 txreq|User-Agent: c1\r **** c1 txreq|\r ** c1 === rxresp **** dT 0.793 *** s1 accepted fd 4 0.0.0.0 0 ** s1 === rxreq **** s1 rxhdr|GET / HTTP/1.1\r **** s1 rxhdr|Host: 127.0.0.1\r **** s1 rxhdr|User-Agent: c1\r **** s1 rxhdr|X-Forwarded-For: 0.0.0.0\r **** s1 rxhdr|Via: 1.1 v1 (Vinyl-Cache/trunk)\r **** s1 rxhdr|Accept-Encoding: gzip\r **** s1 rxhdr|X-Vinyl: 1002\r **** s1 rxhdr|\r **** s1 rxhdrlen = 148 **** s1 http[ 0] |GET **** s1 http[ 1] |/ **** s1 http[ 2] |HTTP/1.1 **** s1 http[ 3] |Host: 127.0.0.1 **** s1 http[ 4] |User-Agent: c1 **** s1 http[ 5] |X-Forwarded-For: 0.0.0.0 **** s1 http[ 6] |Via: 1.1 v1 (Vinyl-Cache/trunk) **** s1 http[ 7] |Accept-Encoding: gzip **** s1 http[ 8] |X-Vinyl: 1002 **** s1 bodylen = 0 ** s1 === txresp -body "012345\n" **** dT 0.794 **** s1 txresp|HTTP/1.1 200 OK\r **** s1 txresp|Date: Tue, 17 Mar 2026 11:26:37 GMT\r **** s1 txresp|Server: s1\r **** s1 txresp|Content-Length: 7\r **** s1 txresp|\r **** s1 txresp|012345 *** s1 shutting fd 4 (server run) ** s1 Ending **** dT 0.805 **** c1 rxhdr|HTTP/1.1 200 OK\r **** c1 rxhdr|Date: Tue, 17 Mar 2026 11:26:37 GMT\r **** c1 rxhdr|Server: s1\r **** c1 rxhdr|Content-Length: 7\r **** c1 rxhdr|X-Vinyl: 1001\r **** c1 rxhdr|Age: 0\r **** c1 rxhdr|Accept-Ranges: bytes\r **** c1 rxhdr|Connection: keep-alive\r **** c1 rxhdr|\r **** c1 rxhdrlen = 156 **** c1 http[ 0] |HTTP/1.1 **** c1 http[ 1] |200 **** c1 http[ 2] |OK **** c1 http[ 3] |Date: Tue, 17 Mar 2026 11:26:37 GMT **** c1 http[ 4] |Server: s1 **** c1 http[ 5] |Content-Length: 7 **** c1 http[ 6] |X-Vinyl: 1001 **** c1 http[ 7] |Age: 0 **** c1 http[ 8] |Accept-Ranges: bytes **** c1 http[ 9] |Connection: keep-alive **** c1 c-l|012345 **** c1 bodylen = 7 ** c1 === expect resp.status == 200 **** c1 EXPECT resp.status (200) == "200" match *** c1 closing fd 18 ** c1 Ending ** top === vinyl v1 -expect n_object == 1 ** v1 as expected: n_object (1) == 1 (1) ** top === vinyl v1 -expect sess_conn == 1 ** v1 as expected: sess_conn (1) == 1 (1) ** top === vinyl v1 -expect client_req == 1 ** v1 as expected: client_req (1) == 1 (1) ** top === vinyl v1 -expect cache_miss == 1 ** v1 as expected: cache_miss (1) == 1 (1) ** top === vinyl v1 -expect s_sess == 1 ** v1 as expected: s_sess (1) == 1 (1) ** top === vinyl v1 -expect s_resp_bodybytes == 7 ** v1 as expected: s_resp_bodybytes (7) == 7 (7) ** top === vinyl v1 -expect s_resp_hdrbytes == 156 ** v1 as expected: s_resp_hdrbytes (156) == 156 (156) ** top === vinyl v2 -arg "-a ${tmpdir}/v1.sock -b '${bad_backend}'" -st... **** dT 0.818 ** v2 Launch *** v2 CMD: cd ${pwd} && exec vinyld -d -n /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v2 -i v2 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p thread_pool_min=10 -p debug=+vtc_mode -p vsl_mask=+Debug,+H2RxHdr,+H2RxBody -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -M '127.0.0.1 34075' -P /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v2/vinyld.pid -p vmod_path=/tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/vmod/.libs: -a /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock -b '127.0.0.1:47618' *** v2 CMD: cd /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest && exec vinyld -d -n /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v2 -i v2 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p thread_pool_min=10 -p debug=+vtc_mode -p vsl_mask=+Debug,+H2RxHdr,+H2RxBody -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -M '127.0.0.1 34075' -P /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v2/vinyld.pid -p vmod_path=/tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/vmod/.libs: -a /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock -b '127.0.0.1:47618' **** dT 0.821 *** v2 PID: 890954 **** v2 macro def v2_pid=890954 **** v2 macro def v2_name=/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v2 **** dT 0.856 **** v1 vsl| 0 Debug - WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 0 0 2040} **** v1 vsl| 0 Debug - WS_ReserveSize(wrk, fffffc7fee2bac70, 280/2040) = 280 **** v1 vsl| 0 Debug - WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 0 280 2040} **** v1 vsl| 0 Debug - WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 0 280 2040} **** v1 vsl| 0 Debug - WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 0 280 2040} **** v1 vsl| 0 Debug - WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 0 280 2040} **** v1 vsl| 0 Debug - WS_Init(ses, fffffc7feed99cb0, fffffc7feed99d08, 584) **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 0 0 576} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 0 0 576} **** v1 vsl| 0 Debug - WS_ReserveSize(ses, fffffc7feed99cb0, 36/576) = 36 **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 0 36 576} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 0 36 576} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 0 36 576} **** v1 vsl| 0 Debug - WS_Release(ses, fffffc7feed99cb0, 36) **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 40 0 576} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 40 0 576} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 40 0 576} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 40 0 576} **** v1 vsl| 0 Debug - WS_ReserveSize(ses, fffffc7feed99cb0, 8/536) = 8 **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 40 8 576} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 40 8 576} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 40 8 576} **** v1 vsl| 0 Debug - WS_Release(ses, fffffc7feed99cb0, 8) **** dT 0.857 **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 48 0 576} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 48 0 576} **** v1 vsl| 0 Debug - WS_ReserveSize(ses, fffffc7feed99cb0, 2/528) = 2 **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 48 2 576} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 48 2 576} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 48 2 576} **** v1 vsl| 0 Debug - WS_Release(ses, fffffc7feed99cb0, 2) **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 56 0 576} **** v1 vsl| 1000 Begin c sess 0 HTTP/1 **** v1 vsl| 1000 SessOpen c 0.0.0.0 0 a0 0.0.0.0 0 1773746797.012663 17 **** v1 vsl| 1000 Debug c sockopt: Test confirmed SO_KEEPALIVE non heredity for a0=/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock **** v1 vsl| 1000 Debug c sockopt: Test confirmed SO_SNDTIMEO non heredity for a0=/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock **** v1 vsl| 1000 Debug c sockopt: Test confirmed SO_RCVTIMEO non heredity for a0=/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock **** v1 vsl| 1000 Debug c sockopt: SO_LINGER may be inherited for a0=/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock **** v1 vsl| 1000 Debug c sockopt: Setting SO_KEEPALIVE for a0=/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock **** v1 vsl| 1000 Debug c sockopt: Setting SO_SNDTIMEO for a0=/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock **** v1 vsl| 1000 Debug c sockopt: Setting SO_RCVTIMEO for a0=/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock **** v1 vsl| 0 Debug - WS_Init(req, fffffc7fec2a0188, fffffc7fec2a50d0, 77632) **** v1 vsl| 0 Debug - WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 0 0 77624} **** v1 vsl| 0 Debug - WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 0 280 2040} **** v1 vsl| 0 Debug - WS_Release(wrk, fffffc7fee2bac70, 0) **** v1 vsl| 0 Debug - WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 0 0 2040} **** v1 vsl| 0 Debug - WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 0 0 77624} **** v1 vsl| 0 Debug - WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 0 0 77624} **** v1 vsl| 0 Debug - WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 0 0 77624} **** v1 vsl| 0 Debug - WS_Reset(req, fffffc7fec2a0188, fffffc7fec2a50d0) **** v1 vsl| 0 Debug - WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 0 0 77624} **** v1 vsl| 0 Debug - WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 0 0 77624} **** v1 vsl| 0 Debug - WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 0 77624 77624} **** v1 vsl| 0 Debug - WS_ReserveAll(req, fffffc7fec2a0188) = 77624 **** v1 vsl| 0 Debug - WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 0 77624 77624} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 56 0 576} **** v1 vsl| 0 Debug - WS_ReserveSize(ses, fffffc7feed99cb0, 8/520) = 8 **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 56 8 576} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 56 8 576} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 56 8 576} **** v1 vsl| 0 Debug - WS_Release(ses, fffffc7feed99cb0, 8) **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 64 0 576} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 64 0 576} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 64 0 576} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 64 0 576} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 64 0 576} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 64 0 576} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 64 0 576} **** v1 vsl| 0 Debug - WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 0 77624 77624} **** v1 vsl| 0 Debug - WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 0 77624 77624} **** v1 vsl| 0 Debug - WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 0 77624 77624} **** v1 vsl| 0 Debug - WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 0 77624 77624} **** v1 vsl| 0 Debug - WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 0 77624 77624} **** v1 vsl| 0 Debug - WS_ReleaseP(req, fffffc7fec2a0188, fffffc7fec2a5103 (51)) **** v1 vsl| 0 Debug - WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 56 0 77624} **** v1 vsl| 1000 Link c req 1001 rxreq **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 64 0 576} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 64 0 576} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 64 0 576} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 64 0 576} **** v1 vsl| 0 Witness - lck_director,vbe_dir_finish,387 lck_conn_pool,VCP_Recycle,376 **** v1 vsl| 1002 Begin b bereq 1001 fetch **** v1 vsl| 1002 VCL_use b vcl1 **** v1 vsl| 1002 Timestamp b Start: 1773746797.013692 0.000000 0.000000 **** v1 vsl| 1002 BereqMethod b GET **** v1 vsl| 1002 BereqURL b / **** v1 vsl| 1002 BereqProtocol b HTTP/1.1 **** v1 vsl| 1002 BereqHeader b Host: 127.0.0.1 **** v1 vsl| 1002 BereqHeader b User-Agent: c1 **** v1 vsl| 1002 BereqHeader b X-Forwarded-For: 0.0.0.0 **** v1 vsl| 1002 BereqHeader b Via: 1.1 v1 (Vinyl-Cache/trunk) **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 0 0 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 0 0 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 0 77936 77936} **** v1 vsl| 1002 Debug b WS_ReserveAll(bo, fffffc7fec270078) = 77936 **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 0 77936 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 0 77936 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 0 77936 77936} **** v1 vsl| 1002 Debug b WS_Release(bo, fffffc7fec270078, 22) **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 24 0 77936} **** v1 vsl| 1002 BereqHeader b Accept-Encoding: gzip **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 24 0 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 24 0 77936} **** v1 vsl| 1002 Debug b WS_Copy(bo, fffffc7fec270078, 4) = fffffc7fec274fb0 **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 32 0 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 32 0 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 32 0 77936} **** v1 vsl| 1002 Debug b WS_Copy(bo, fffffc7fec270078, 2) = fffffc7fec274fb8 **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 40 0 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 40 0 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 40 0 77936} **** v1 vsl| 1002 Debug b WS_Copy(bo, fffffc7fec270078, 9) = fffffc7fec274fc0 **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 56 0 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 56 0 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 56 0 77936} **** v1 vsl| 1002 Debug b WS_Copy(bo, fffffc7fec270078, 16) = fffffc7fec274fd0 **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 72 0 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 72 0 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 72 0 77936} **** v1 vsl| 1002 Debug b WS_Copy(bo, fffffc7fec270078, 15) = fffffc7fec274fe0 **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 88 0 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 88 0 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 88 0 77936} **** v1 vsl| 1002 Debug b WS_Copy(bo, fffffc7fec270078, 25) = fffffc7fec274ff0 **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 120 0 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 120 0 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 120 0 77936} **** v1 vsl| 1002 Debug b WS_Copy(bo, fffffc7fec270078, 32) = fffffc7fec275010 **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 152 0 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 152 0 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 152 0 77936} **** v1 vsl| 1002 Debug b WS_Snapshot(bo, fffffc7fec270078) = fffffc7fec275030 **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 152 0 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 152 0 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 152 77784 77936} **** v1 vsl| 1002 Debug b WS_ReserveAll(bo, fffffc7fec270078) = 77784 **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 152 77784 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 152 77784 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 152 77784 77936} **** v1 vsl| 1002 Debug b WS_Release(bo, fffffc7fec270078, 14) **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 168 0 77936} **** v1 vsl| 1002 BereqHeader b X-Vinyl: 1002 **** v1 vsl| 1002 Debug b WS(wrk, fffffc7fee2cfc70) = {fffffc7fee2cf3b0 0 0 2040} **** v1 vsl| 1002 Debug b WS_Snapshot(wrk, fffffc7fee2cfc70) = fffffc7fee2cf3b0 **** v1 vsl| 1002 VCL_call b BACKEND_FETCH **** v1 vsl| 1002 VCL_return b fetch **** v1 vsl| 1002 Debug b WS(wrk, fffffc7fee2cfc70) = {fffffc7fee2cf3b0 0 0 2040} **** v1 vsl| 1002 Debug b WS_Snapshot(wrk, fffffc7fee2cfc70) = fffffc7fee2cf3b0 **** v1 vsl| 1002 Timestamp b Fetch: 1773746797.013807 0.000115 0.000115 **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 168 0 77936} **** v1 vsl| 1002 Debug b WS_Alloc(bo, fffffc7fec270078, 104) = fffffc7fec275040 **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 272 0 77936} **** v1 vsl| 1002 Timestamp b Connected: 1773746797.013905 0.000212 0.000097 **** v1 vsl| 1002 BackendOpen b 22 s1 0.0.0.0 0 0.0.0.0 0 connect **** v1 vsl| 1002 Debug b WS(wrk, fffffc7fee2cfc70) = {fffffc7fee2cf3b0 0 0 2040} **** v1 vsl| 1002 Debug b WS_Snapshot(wrk, fffffc7fee2cfc70) = fffffc7fee2cf3b0 **** v1 vsl| 1002 Debug b WS(wrk, fffffc7fee2cfc70) = {fffffc7fee2cf3b0 0 0 2040} **** v1 vsl| 1002 Debug b WS_Alloc(wrk, fffffc7fee2cfc70, 112) = fffffc7fee2cf3b0 **** v1 vsl| 1002 Debug b WS(wrk, fffffc7fee2cfc70) = {fffffc7fee2cf3b0 112 0 2040} **** v1 vsl| 1002 Debug b WS(wrk, fffffc7fee2cfc70) = {fffffc7fee2cf3b0 112 0 2040} **** v1 vsl| 1002 Debug b WS(wrk, fffffc7fee2cfc70) = {fffffc7fee2cf3b0 112 1928 2040} **** v1 vsl| 1002 Debug b WS_ReserveAll(wrk, fffffc7fee2cfc70) = 1928 **** v1 vsl| 1002 Debug b WS(wrk, fffffc7fee2cfc70) = {fffffc7fee2cf3b0 112 1928 2040} **** v1 vsl| 1002 Debug b WS(wrk, fffffc7fee2cfc70) = {fffffc7fee2cf3b0 112 1928 2040} **** v1 vsl| 1002 Debug b WS_Release(wrk, fffffc7fee2cfc70, 1920) **** v1 vsl| 1002 Debug b WS(wrk, fffffc7fee2cfc70) = {fffffc7fee2cf3b0 2032 0 2040} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 272 0 77936} **** v1 vsl| 1002 Debug b WS_Alloc(bo, fffffc7fec270078, 56) = fffffc7fec2750a8 **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 328 0 77936} **** v1 vsl| 1002 Debug b WS(wrk, fffffc7fee2cfc70) = {fffffc7fee2cf3b0 2032 0 2040} **** v1 vsl| 1002 Debug b WS(wrk, fffffc7fee2cfc70) = {fffffc7fee2cf3b0 2032 0 2040} **** v1 vsl| 1002 Debug b WS_Reset(wrk, fffffc7fee2cfc70, fffffc7fee2cf3b0) **** v1 vsl| 1002 Debug b WS(wrk, fffffc7fee2cfc70) = {fffffc7fee2cf3b0 0 0 2040} **** v1 vsl| 1002 Timestamp b Bereq: 1773746797.014017 0.000324 0.000111 **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 328 0 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 328 0 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 328 77608 77936} **** v1 vsl| 1002 Debug b WS_ReserveAll(bo, fffffc7fec270078) = 77608 **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 328 77608 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 328 77608 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 328 77608 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 328 77608 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 328 77608 77936} **** v1 vsl| 1002 Debug b WS_ReleaseP(bo, fffffc7fec270078, fffffc7fec27513e (94)) **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 424 0 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 424 0 77936} **** v1 vsl| 1002 Debug b WS_Alloc(bo, fffffc7fec270078, 64) = fffffc7fec275140 **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 488 0 77936} **** v1 vsl| 1002 BerespProtocol b HTTP/1.1 **** v1 vsl| 1002 BerespStatus b 200 **** v1 vsl| 1002 BerespReason b OK **** v1 vsl| 1002 BerespHeader b Date: Tue, 17 Mar 2026 11:26:37 GMT **** v1 vsl| 1002 BerespHeader b Server: s1 **** v1 vsl| 1002 BerespHeader b Content-Length: 7 **** v1 vsl| 1002 Timestamp b Beresp: 1773746797.014517 0.000824 0.000499 **** v1 vsl| 1002 TTL b RFC 120 10 0 1773746797 1773746797 1773746797 0 0 cacheable **** v1 vsl| 1002 Debug b WS(wrk, fffffc7fee2cfc70) = {fffffc7fee2cf3b0 0 0 2040} **** v1 vsl| 1002 Debug b WS_Snapshot(wrk, fffffc7fee2cfc70) = fffffc7fee2cf3b0 **** v1 vsl| 1002 VCL_call b BACKEND_RESPONSE **** v1 vsl| 1002 VCL_return b deliver **** v1 vsl| 1002 Debug b WS(wrk, fffffc7fee2cfc70) = {fffffc7fee2cf3b0 0 0 2040} **** v1 vsl| 1002 Debug b WS_Snapshot(wrk, fffffc7fee2cfc70) = fffffc7fee2cf3b0 **** v1 vsl| 1002 Debug b Missing content-range header or unknown range unit **** v1 vsl| 1002 Timestamp b Process: 1773746797.014652 0.000959 0.000135 **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 488 0 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 488 0 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 488 77448 77936} **** v1 vsl| 1002 Debug b WS_ReserveAll(bo, fffffc7fec270078) = 77448 **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 488 77448 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 488 77448 77936} **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 488 77448 77936} **** v1 vsl| 1002 Debug b WS_Release(bo, fffffc7fec270078, 1) **** v1 vsl| 1002 Debug b WS(bo, fffffc7fec270078) = {fffffc7fec274f98 496 0 77936} **** v1 vsl| 1002 Filters b **** v1 vsl| 1002 Storage b umem s0 **** v1 vsl| 1002 Fetch_Body b 3 length - **** v1 vsl| 1002 BackendClose b 22 s1 recycle **** v1 vsl| 1002 Timestamp b BerespBody: 1773746797.024866 0.011174 0.010214 **** v1 vsl| 1002 Length b 7 **** v1 vsl| 1002 BereqAcct b 148 0 148 87 7 94 **** v1 vsl| 1002 End b **** v1 vsl| 0 Witness - lck_exp,exp_thread,438 lck_wstat,Pool_Sumstat,61 **** v1 vsl| 0 Debug - WS(wrk, fffffc7fee2cfc70) = {fffffc7fee2cf3b0 0 0 2040} **** v1 vsl| 0 Debug - WS(wrk, fffffc7fee2cfc70) = {fffffc7fee2cf3b0 0 0 2040} **** v1 vsl| 0 Debug - WS_Reset(wrk, fffffc7fee2cfc70, fffffc7fee2cf3b0) **** v1 vsl| 0 Debug - WS(wrk, fffffc7fee2cfc70) = {fffffc7fee2cf3b0 0 0 2040} **** v1 vsl| 0 Witness - lck_wstat,pool_stat_summ,129 lck_perpool,pool_stat_summ,132 **** v1 vsl| 0 Debug - WS(wrk, fffffc7fee2cfc70) = {fffffc7fee2cf3b0 0 0 2040} **** v1 vsl| 0 Debug - WS(wrk, fffffc7fee2cfc70) = {fffffc7fee2cf3b0 0 0 2040} **** v1 vsl| 0 Debug - WS_Reset(wrk, fffffc7fee2cfc70, fffffc7fee2cf3b0) **** v1 vsl| 0 Debug - WS(wrk, fffffc7fee2cfc70) = {fffffc7fee2cf3b0 0 0 2040} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 64 0 576} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 64 0 576} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 64 0 576} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 64 0 576} **** v1 vsl| 1001 Begin c req 1000 rxreq **** v1 vsl| 1001 Timestamp c Start: 1773746797.013085 0.000000 0.000000 **** v1 vsl| 1001 Timestamp c Req: 1773746797.013085 0.000000 0.000000 **** v1 vsl| 1001 VCL_use c vcl1 **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 56 0 77624} **** v1 vsl| 1001 Debug c WS_Snapshot(req, fffffc7fec2a0188) = fffffc7fec2a5108 **** v1 vsl| 1001 Debug c WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 64 0 576} **** v1 vsl| 1001 Debug c WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 64 0 576} **** v1 vsl| 1001 Debug c WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 64 0 576} **** v1 vsl| 1001 Debug c WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 64 0 576} **** v1 vsl| 1001 ReqStart c 0.0.0.0 0 a0 **** v1 vsl| 1001 ReqMethod c GET **** v1 vsl| 1001 ReqURL c / **** v1 vsl| 1001 ReqProtocol c HTTP/1.1 **** v1 vsl| 1001 ReqHeader c Host: 127.0.0.1 **** v1 vsl| 1001 ReqHeader c User-Agent: c1 **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 56 0 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 56 0 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 56 77568 77624} **** v1 vsl| 1001 Debug c WS_ReserveAll(req, fffffc7fec2a0188) = 77568 **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 56 77568 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 56 77568 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 56 77568 77624} **** v1 vsl| 1001 Debug c WS_Release(req, fffffc7fec2a0188, 25) **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 88 0 77624} **** v1 vsl| 1001 ReqHeader c X-Forwarded-For: 0.0.0.0 **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 88 0 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 88 0 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 88 77536 77624} **** v1 vsl| 1001 Debug c WS_ReserveAll(req, fffffc7fec2a0188) = 77536 **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 88 77536 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 88 77536 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 88 77536 77624} **** v1 vsl| 1001 Debug c WS_Release(req, fffffc7fec2a0188, 32) **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 120 0 77624} **** v1 vsl| 1001 ReqHeader c Via: 1.1 v1 (Vinyl-Cache/trunk) **** v1 vsl| 1001 Debug c WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 0 0 2040} **** v1 vsl| 1001 Debug c WS_Snapshot(wrk, fffffc7fee2bac70) = fffffc7fee2ba3b0 **** v1 vsl| 1001 VCL_call c RECV **** v1 vsl| 1001 VCL_return c hash **** v1 vsl| 1001 Debug c WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 0 0 2040} **** v1 vsl| 1001 Debug c WS_Snapshot(wrk, fffffc7fee2bac70) = fffffc7fee2ba3b0 **** v1 vsl| 1001 Debug c WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 0 0 2040} **** v1 vsl| 1001 Debug c WS_Snapshot(wrk, fffffc7fee2bac70) = fffffc7fee2ba3b0 **** v1 vsl| 1001 VCL_call c HASH **** v1 vsl| 1001 VCL_return c lookup **** v1 vsl| 1001 Debug c WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 0 0 2040} **** v1 vsl| 1001 Debug c WS_Snapshot(wrk, fffffc7fee2bac70) = fffffc7fee2ba3b0 **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 120 0 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 120 77504 77624} **** v1 vsl| 1001 Debug c WS_ReserveAll(req, fffffc7fec2a0188) = 77504 **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 120 77504 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 120 77504 77624} **** v1 vsl| 1001 Debug c WS_Release(req, fffffc7fec2a0188, 0) **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 120 0 77624} **** v1 vsl| 1001 Debug c WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 0 0 2040} **** v1 vsl| 1001 Debug c WS_Snapshot(wrk, fffffc7fee2bac70) = fffffc7fee2ba3b0 **** v1 vsl| 1001 VCL_call c MISS **** v1 vsl| 1001 VCL_return c fetch **** v1 vsl| 1001 Debug c WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 0 0 2040} **** v1 vsl| 1001 Debug c WS_Snapshot(wrk, fffffc7fee2bac70) = fffffc7fee2ba3b0 **** v1 vsl| 1001 Debug c WS_Init(bo, fffffc7fec270078, fffffc7fec274f98, 77944) **** v1 vsl| 1001 Debug c WS(bo, fffffc7fec270078) = {fffffc7fec274f98 0 0 77936} **** v1 vsl| 1001 Link c bereq 1002 fetch **** v1 vsl| 1001 Timestamp c Fetch: 1773746797.024886 0.011801 0.011801 **** v1 vsl| 1001 RespProtocol c HTTP/1.1 **** v1 vsl| 1001 RespStatus c 200 **** v1 vsl| 1001 RespReason c OK **** v1 vsl| 1001 RespHeader c Date: Tue, 17 Mar 2026 11:26:37 GMT **** v1 vsl| 1001 RespHeader c Server: s1 **** v1 vsl| 1001 RespHeader c Content-Length: 7 **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 120 0 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 120 0 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 120 77504 77624} **** v1 vsl| 1001 Debug c WS_ReserveAll(req, fffffc7fec2a0188) = 77504 **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 120 77504 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 120 77504 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 120 77504 77624} **** v1 vsl| 1001 Debug c WS_Release(req, fffffc7fec2a0188, 14) **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 136 0 77624} **** v1 vsl| 1001 RespHeader c X-Vinyl: 1001 **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 136 0 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 136 0 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 136 77488 77624} **** v1 vsl| 1001 Debug c WS_ReserveAll(req, fffffc7fec2a0188) = 77488 **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 136 77488 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 136 77488 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 136 77488 77624} **** v1 vsl| 1001 Debug c WS_Release(req, fffffc7fec2a0188, 7) **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 144 0 77624} **** v1 vsl| 1001 RespHeader c Age: 0 **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 144 0 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 144 0 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 144 77480 77624} **** v1 vsl| 1001 Debug c WS_ReserveAll(req, fffffc7fec2a0188) = 77480 **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 144 77480 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 144 77480 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 144 77480 77624} **** v1 vsl| 1001 Debug c WS_Release(req, fffffc7fec2a0188, 32) **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 176 0 77624} **** v1 vsl| 1001 RespHeader c Via: 1.1 v1 (Vinyl-Cache/trunk) **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 176 0 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 176 0 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 176 77448 77624} **** v1 vsl| 1001 Debug c WS_ReserveAll(req, fffffc7fec2a0188) = 77448 **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 176 77448 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 176 77448 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 176 77448 77624} **** v1 vsl| 1001 Debug c WS_Release(req, fffffc7fec2a0188, 21) **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 200 0 77624} **** v1 vsl| 1001 RespHeader c Accept-Ranges: bytes **** v1 vsl| 1001 Debug c WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 0 0 2040} **** v1 vsl| 1001 Debug c WS_Snapshot(wrk, fffffc7fee2bac70) = fffffc7fee2ba3b0 **** v1 vsl| 1001 VCL_call c DELIVER **** v1 vsl| 1001 RespUnset c Via: 1.1 v1 (Vinyl-Cache/trunk) **** v1 vsl| 1001 VCL_return c deliver **** v1 vsl| 1001 Debug c WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 0 0 2040} **** v1 vsl| 1001 Debug c WS_Snapshot(wrk, fffffc7fee2bac70) = fffffc7fee2ba3b0 **** v1 vsl| 1001 Timestamp c Process: 1773746797.025019 0.011934 0.000133 **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 200 0 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 200 0 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 200 77424 77624} **** v1 vsl| 1001 Debug c WS_ReserveAll(req, fffffc7fec2a0188) = 77424 **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 200 77424 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 200 77424 77624} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 200 77424 77624} **** v1 vsl| 1001 Debug c WS_Release(req, fffffc7fec2a0188, 1) **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 208 0 77624} **** v1 vsl| 1001 Filters c **** v1 vsl| 1001 RespHeader c Connection: keep-alive **** v1 vsl| 1001 Debug c WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 0 0 2040} **** v1 vsl| 1001 Debug c WS_Snapshot(wrk, fffffc7fee2bac70) = fffffc7fee2ba3b0 **** v1 vsl| 1001 Debug c WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 0 0 2040} **** v1 vsl| 1001 Debug c WS_Alloc(wrk, fffffc7fee2bac70, 112) = fffffc7fee2ba3b0 **** v1 vsl| 1001 Debug c WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 112 0 2040} **** v1 vsl| 1001 Debug c WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 112 0 2040} **** v1 vsl| 1001 Debug c WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 112 1928 2040} **** v1 vsl| 1001 Debug c WS_ReserveAll(wrk, fffffc7fee2bac70) = 1928 **** v1 vsl| 1001 Debug c WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 112 1928 2040} **** v1 vsl| 1001 Debug c WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 112 1928 2040} **** v1 vsl| 1001 Debug c WS_Release(wrk, fffffc7fee2bac70, 1024) **** v1 vsl| 1001 Debug c WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 1136 0 2040} **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 208 0 77624} **** v1 vsl| 1001 Debug c WS_Alloc(req, fffffc7fec2a0188, 56) = fffffc7fec2a51a0 **** v1 vsl| 1001 Debug c WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 264 0 77624} **** v1 vsl| 1001 Debug c WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 1136 0 2040} **** v1 vsl| 1001 Debug c WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 1136 0 2040} **** v1 vsl| 1001 Debug c WS_Reset(wrk, fffffc7fee2bac70, fffffc7fee2ba3b0) **** v1 vsl| 1001 Debug c WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 0 0 2040} **** v1 vsl| 1001 Timestamp c Resp: 1773746797.025134 0.012049 0.000115 **** v1 vsl| 1001 ReqAcct c 51 0 51 156 7 163 **** v1 vsl| 1001 End c **** v1 vsl| 0 Debug - WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 264 0 77624} **** v1 vsl| 0 Debug - WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 264 0 77624} **** v1 vsl| 0 Debug - WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 264 0 77624} **** v1 vsl| 0 Debug - WS_Reset(req, fffffc7fec2a0188, fffffc7fec2a50d0) **** v1 vsl| 0 Debug - WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 0 0 77624} **** v1 vsl| 0 Debug - WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 0 0 77624} **** v1 vsl| 0 Debug - WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 0 77624 77624} **** v1 vsl| 0 Debug - WS_ReserveAll(req, fffffc7fec2a0188) = 77624 **** v1 vsl| 0 Debug - WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 0 77624 77624} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 64 0 576} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 64 0 576} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 64 0 576} **** v1 vsl| 0 Debug - WS(ses, fffffc7feed99cb0) = {fffffc7feed99d08 64 0 576} **** v1 vsl| 0 Debug - WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 0 77624 77624} **** v1 vsl| 0 Debug - WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 0 77624 77624} **** v1 vsl| 0 Debug - WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 0 77624 77624} **** v1 vsl| 0 Debug - WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 0 77624 77624} **** v1 vsl| 0 Debug - WS_ReleaseP(req, fffffc7fec2a0188, fffffc7fec2a50d0 (0)) **** v1 vsl| 0 Debug - WS(req, fffffc7fec2a0188) = {fffffc7fec2a50d0 0 0 77624} **** v1 vsl| 1000 SessClose c REM_CLOSE 0.013 **** v1 vsl| 1000 End c **** v1 vsl| 0 Debug - WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 0 0 2040} **** v1 vsl| 0 Debug - WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 0 0 2040} **** v1 vsl| 0 Debug - WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 0 0 2040} **** v1 vsl| 0 Debug - WS_Reset(wrk, fffffc7fee2bac70, fffffc7fee2ba3b0) **** v1 vsl| 0 Debug - WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 0 0 2040} **** v1 vsl| 0 Witness - lck_wstat,pool_stat_summ,129 lck_perpool,pool_stat_summ,132 **** v1 vsl| 0 Debug - WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 0 0 2040} **** v1 vsl| 0 Debug - WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 0 0 2040} **** v1 vsl| 0 Debug - WS_Reset(wrk, fffffc7fee2bac70, fffffc7fee2ba3b0) **** v1 vsl| 0 Debug - WS(wrk, fffffc7fee2bac70) = {fffffc7fee2ba3b0 0 0 2040} **** dT 1.355 *** v2 debug|Warnings: *** v2 debug|Message from VCC-compiler: *** v2 debug|Security: proc_setid missing, uid/gid unchanged *** v2 debug|Message from C-compiler: *** v2 debug|Security: proc_setid missing, uid/gid unchanged *** v2 debug|cc: Warning: Option -fno-var-tracking-assignments passed to ld, if ld is invoked, ignored otherwise *** v2 debug|"vgc.c", line 3172: warning: statement not reached (E_STATEMENT_NOT_REACHED) *** v2 debug|Message from dlopen: *** v2 debug|Security: proc_setid missing, uid/gid unchanged *** v2 debug| **** dT 1.367 *** v2 debug|Warning: mlock() of VSM failed: Not owner *** v2 debug| (1) **** dT 1.371 *** v2 debug|Debug: Version: vinyl-cache-trunk revision 17ea9216e8091e8bd4b65a2e0b946c92de2125f4 *** v2 debug| *** v2 debug|Debug: *** v2 debug| Platform: -jsolaris,-sdefault,-sdefault,-hcritbit *** v2 debug| *** v2 debug|200 292 *** v2 debug|----------------------------- *** v2 debug|Vinyl Cache CLI 1.0 *** v2 debug|----------------------------- *** v2 debug|-jsolaris,-sdefault,-sdefault,-hcritbit *** v2 debug|vinyl-cache-trunk revision 17ea9216e8091e8bd4b65a2e0b946c92de2125f4 *** v2 debug| *** v2 debug|Type 'help' for command list. *** v2 debug|Type 'quit' to close CLI session. *** v2 debug|Type 'start' to launch worker process. *** v2 debug| **** dT 1.468 **** v2 CLIPOLL 1 0x1 0x0 0x0 *** v2 CLI connection fd = 18 *** v2 CLI RX 107 **** v2 CLI RX|utogzwowtipfyrpfuvvymlyvtuejnljv **** v2 CLI RX| **** v2 CLI RX|Authentication required. **** dT 1.469 **** v2 CLI TX|auth 3478fcf2f07029b55f89d5f7876deee7cdbe7065243df6cf6b15254ee7dc5b29 *** v2 CLI RX 200 **** v2 CLI RX|----------------------------- **** v2 CLI RX|Vinyl Cache CLI 1.0 **** v2 CLI RX|----------------------------- **** v2 CLI RX|-jsolaris,-sdefault,-sdefault,-hcritbit **** v2 CLI RX|vinyl-cache-trunk revision 17ea9216e8091e8bd4b65a2e0b946c92de2125f4 **** v2 CLI RX| **** v2 CLI RX|Type 'help' for command list. **** v2 CLI RX|Type 'quit' to close CLI session. **** v2 CLI RX|Type 'start' to launch worker process. **** dT 1.470 ** v2 Start **** v2 CLI TX|start **** dT 1.483 *** v2 debug|Debug: Child (891025) Started **** dT 1.515 *** v2 debug|Child launched OK **** dT 1.517 *** v2 CLI RX 200 *** v2 debug|Info: Child (891025) said Child starts *** v2 wait-running **** v2 CLI TX|status *** v2 debug|Info: Child (891025) said notice:\tlibumem was already found to be loaded *** v2 debug|Info: Child (891025) said Security: proc_setid missing, uid/gid unchanged *** v2 debug| *** v2 CLI RX 200 **** v2 CLI RX|Child in state running **** v2 CLI TX|debug.listen_address *** v2 CLI RX 200 **** v2 CLI RX|a0 /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock - **** v2 CLI TX|debug.xid 1000 *** v2 CLI RX 200 **** v2 CLI RX|XID is 1000 chunk 1 **** v2 CLI TX|debug.listen_address *** v2 CLI RX 200 **** v2 CLI RX|a0 /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock - ** v2 Listen on 0.0.0.0 0 **** v2 macro def v2_addr=0.0.0.0 **** v2 macro def v2_port=0 **** v2 macro def v2_sock=/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock **** v2 macro def v2_a0_addr=0.0.0.0 **** v2 macro def v2_a0_port=0 **** v2 macro def v2_a0_sock=/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock **** dT 1.570 **** v2 vsl| 0 CLI - Rd vcl.load "boot" vcl_boot.1773746797.068929/vgc.so 1auto **** v2 vsl| 0 CLI - Wr 200 52 Loaded "vcl_boot.1773746797.068929/vgc.so" as "boot" **** v2 vsl| 0 CLI - Rd vcl.use "boot" **** v2 vsl| 0 CLI - Wr 200 0 **** v2 vsl| 0 CLI - Rd start **** v2 vsl| 0 Debug - sockopt: Setting SO_LINGER for a0=/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock **** v2 vsl| 0 Debug - sockopt: Setting SO_KEEPALIVE for a0=/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock **** v2 vsl| 0 Debug - sockopt: Setting SO_SNDTIMEO for a0=/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock **** v2 vsl| 0 Debug - sockopt: Setting SO_RCVTIMEO for a0=/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock **** v2 vsl| 0 CLI - Wr 200 0 **** v2 vsl| 0 CLI - Rd debug.listen_address **** v2 vsl| 0 CLI - Wr 200 80 a0 /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock - **** v2 vsl| 0 CLI - Rd debug.xid 1000 **** v2 vsl| 0 CLI - Wr 200 19 XID is 1000 chunk 1 **** v2 vsl| 0 CLI - Rd debug.listen_address **** v2 vsl| 0 CLI - Wr 200 80 a0 /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock - **** dT 1.617 ** top === vinyl v2 -syntax 4.0 -errvcl {Compiled VCL version (4.0) not... **** v2 CLI TX|vcl.inline vcl1 << %XJEIFLH|)Xspa8P **** v2 CLI TX|vcl 4.0; **** v2 CLI TX| **** v2 CLI TX|\tbackend default None; **** v2 CLI TX| **** v2 CLI TX|%XJEIFLH|)Xspa8P **** dT 1.858 **** v1 vsl| 0 Witness - lck_perpool,Pool_Work_Thread,399 lck_vcl,VCL_Rel,132 **** dT 2.084 *** v2 CLI RX 106 **** dT 2.085 **** v2 CLI RX|Message from VCC-compiler: **** v2 CLI RX|Security: proc_setid missing, uid/gid unchanged **** v2 CLI RX|Message from C-compiler: **** v2 CLI RX|Security: proc_setid missing, uid/gid unchanged **** v2 CLI RX|cc: Warning: Option -fno-var-tracking-assignments passed to ld, if ld is invoked, ignored otherwise **** v2 CLI RX|"vgc.c", line 3146: warning: statement not reached (E_STATEMENT_NOT_REACHED) **** v2 CLI RX|Message from dlopen: **** v2 CLI RX|Security: proc_setid missing, uid/gid unchanged **** v2 CLI RX|Compiled VCL version (4.0) not supported. **** v2 CLI RX|Running dlopen failed, exited with 1 **** v2 CLI RX|VCL compilation failed ** v2 VCL compilation failed (as expected) *** v2 Found expected string: ("Compiled VCL version (4.0) not supported.") ** top === vinyl v2 -syntax 4.0 -errvcl \ **** v2 CLI TX|vcl.inline vcl2 << %XJEIFLH|)Xspa8P **** v2 CLI TX|vcl 4.0; **** v2 CLI TX|backend default { .path = "/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock"; } **** v2 CLI TX|%XJEIFLH|)Xspa8P **** dT 2.095 *** v2 CLI RX 106 **** dT 2.096 **** v2 CLI RX|Message from VCC-compiler: **** v2 CLI RX|Security: proc_setid missing, uid/gid unchanged **** v2 CLI RX|Unix socket backends only supported in VCL4.1 and higher. **** v2 CLI RX|'"/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock"' at ('' Line 2 Pos 27) **** v2 CLI RX|backend default { .path = "/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock"; } **** v2 CLI RX|--------------------------############################################################################--- **** v2 CLI RX| **** v2 CLI RX| **** v2 CLI RX|In backend specification starting at: **** v2 CLI RX|('' Line 2 Pos 1) **** v2 CLI RX|backend default { .path = "/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock"; } **** v2 CLI RX|#######-------------------------------------------------------------------------------------------------- **** v2 CLI RX| **** v2 CLI RX|Running VCC-compiler failed, exited with 2 **** v2 CLI RX|VCL compilation failed ** v2 VCL compilation failed (as expected) *** v2 Found expected string: ("Unix socket backends only supported in VCL4.1 and higher.") ** top === vinyl v3 -vcl { **** dT 2.107 ** v3 Launch *** v3 CMD: cd ${pwd} && exec vinyld -d -n /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v3 -i v3 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p thread_pool_min=10 -p debug=+vtc_mode -p vsl_mask=+Debug,+H2RxHdr,+H2RxBody -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -a '127.0.0.1:0' -M '127.0.0.1 47808' -P /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v3/vinyld.pid -p vmod_path=/tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/vmod/.libs: *** v3 CMD: cd /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest && exec vinyld -d -n /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v3 -i v3 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p thread_pool_min=10 -p debug=+vtc_mode -p vsl_mask=+Debug,+H2RxHdr,+H2RxBody -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -a '127.0.0.1:0' -M '127.0.0.1 47808' -P /tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v3/vinyld.pid -p vmod_path=/tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/vmod/.libs: **** dT 2.110 *** v3 PID: 891148 **** v3 macro def v3_pid=891148 **** v3 macro def v3_name=/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v3 **** dT 2.154 *** v3 debug|Warning: mlock() of VSM failed: Not owner (1) **** dT 2.157 *** v3 debug|Debug: Version: vinyl-cache-trunk revision 17ea9216e8091e8bd4b65a2e0b946c92de2125f4 *** v3 debug| *** v3 debug|Debug: *** v3 debug|Platform: -jsolaris,-sdefault,-sdefault,-hcritbit *** v3 debug|200 292 *** v3 debug|----------------------------- *** v3 debug|Vinyl Cache CLI 1.0 *** v3 debug|----------------------------- *** v3 debug|-jsolaris,-sdefault,-sdefault,-hcritbit *** v3 debug|vinyl-cache-trunk revision 17ea9216e8091e8bd4b65a2e0b946c92de2125f4 *** v3 debug| *** v3 debug|Type 'help' for command list. *** v3 debug|Type 'quit' to close CLI session. *** v3 debug|Type 'start' to launch worker process. *** v3 debug| **** dT 2.255 **** v3 CLIPOLL 1 0x1 0x0 0x0 *** v3 CLI connection fd = 29 *** v3 CLI RX 107 **** v3 CLI RX|wqdpypdqlkervdplozhxbxxzgpruxszs **** v3 CLI RX| **** v3 CLI RX|Authentication required. **** dT 2.256 **** v3 CLI TX|auth fb8bfc70603dde63b5a38bf1e8d730bc23b9674cb94321f4d057af93f39f419d *** v3 CLI RX 200 **** v3 CLI RX|----------------------------- **** v3 CLI RX|Vinyl Cache CLI 1.0 **** v3 CLI RX|----------------------------- **** v3 CLI RX|-jsolaris,-sdefault,-sdefault,-hcritbit **** v3 CLI RX|vinyl-cache-trunk revision 17ea9216e8091e8bd4b65a2e0b946c92de2125f4 **** v3 CLI RX| **** v3 CLI RX|Type 'help' for command list. **** v3 CLI RX|Type 'quit' to close CLI session. **** v3 CLI RX|Type 'start' to launch worker process. **** dT 2.257 **** v3 CLI TX|vcl.inline vcl1 << %XJEIFLH|)Xspa8P **** v3 CLI TX|vcl 4.1; **** v3 CLI TX| **** v3 CLI TX|\tbackend default None; **** v3 CLI TX| **** v3 CLI TX|\tsub vcl_recv { **** v3 CLI TX|\t\treturn(synth(200)); **** v3 CLI TX|\t} **** v3 CLI TX| **** v3 CLI TX|\tsub vcl_synth { **** v3 CLI TX|\t\tset resp.http.addr = "0.0.0.0"; **** v3 CLI TX|\t\tset resp.http.port = "0"; **** v3 CLI TX|\t\tset resp.http.sock = "/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock"; **** v3 CLI TX|\t\tset resp.http.a0_addr = "0.0.0.0"; **** v3 CLI TX|\t\tset resp.http.a0_port = "0"; **** v3 CLI TX|\t\tset resp.http.a0_sock = "/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v1.sock"; **** v3 CLI TX|\t\tset resp.http.a1_addr = "127.0.0.1"; **** v3 CLI TX|\t\tset resp.http.a1_port = "35917"; **** v3 CLI TX|\t\tset resp.http.a1_sock = "127.0.0.1:35917"; **** v3 CLI TX|\t} **** v3 CLI TX| **** v3 CLI TX|%XJEIFLH|)Xspa8P **** dT 2.357 *** v3 vsl|No VSL chunk found (child not started ?) **** dT 2.457 *** v3 vsl|No VSL chunk found (child not started ?) **** dT 2.557 *** v3 vsl|No VSL chunk found (child not started ?) **** dT 2.657 *** v3 vsl|No VSL chunk found (child not started ?) **** dT 2.757 *** v3 vsl|No VSL chunk found (child not started ?) **** dT 2.764 *** v3 CLI RX 200 **** v3 CLI RX|Message from VCC-compiler: **** v3 CLI RX|Security: proc_setid missing, uid/gid unchanged **** v3 CLI RX|Message from C-compiler: **** v3 CLI RX|Security: proc_setid missing, uid/gid unchanged **** v3 CLI RX|cc: Warning: Option -fno-var-tracking-assignments passed to ld, if ld is invoked, ignored otherwise **** v3 CLI RX|"vgc.c", line 3182: warning: statement not reached (E_STATEMENT_NOT_REACHED) **** v3 CLI RX|Message from dlopen: **** v3 CLI RX|Security: proc_setid missing, uid/gid unchanged **** v3 CLI RX|VCL compiled. **** v3 CLI TX|vcl.use vcl1 *** v3 CLI RX 200 **** v3 CLI RX|VCL 'vcl1' now active ** v3 Start **** v3 CLI TX|start **** dT 2.777 *** v3 debug|Debug: *** v3 debug|Child (891363) Started **** dT 2.797 *** v3 debug|Error: Child failed on launch *** v3 debug| **** dT 2.798 *** v3 debug|Error: *** v3 debug|Child (891363) said Child starts *** v3 debug|Error: *** v3 debug|Child (891363) said notice:\tlibumem was already found to be loaded *** v3 debug|Error: *** v3 debug| *** v3 debug|Child (891363) said Security: proc_setid missing, uid/gid unchanged *** v3 debug| **** dT 2.857 **** v3 vsl| 0 Debug - Create worker thread failed 11 Resource temporarily unavailable **** dT 2.898 *** v3 debug|Error: Child (891363) died signal=6 *** v3 debug|Error: *** v3 debug|Child (891363) Panic at: Tue, 17 Mar 2026 11:26:39 GMT *** v3 debug|Wrong turn at ../../../../bin/vinyld/cache/cache_mempool.c:246: *** v3 debug|pthread_create(&mpl->thread, ((void *)0), mpl_guard, mpl) failed *** v3 debug|version = vinyl-cache-trunk revision 17ea9216e8091e8bd4b65a2e0b946c92de2125f4, vrt api = 23.0 *** v3 debug|ident = -jsolaris,-sdefault,-sdefault,-hcritbit,ports *** v3 debug|now = 5958876.071344 (mono), 1773746799.016978 (real) *** v3 debug|Backtrace: *** v3 debug| 6d5a7d: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'vbt_execinfo+0x3d [0x6d5a7d] *** v3 debug| 6d5e21: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'VBT_format+0x41 [0x6d5e21] *** v3 debug| 5539f8: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'pan_backtrace+0x58 [0x5539f8] *** v3 debug| 5540f9: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'pan_ic+0x539 [0x5540f9] *** v3 debug| 6d43ac: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub *** v3 debug|/bin/vinyld/vinyld'VAS_Fail+0x8c [0x6d43ac] *** v3 debug| 545ed2: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'MPL_New+0x332 [0x545ed2] *** v3 debug| 57705f: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'SES_NewPool+0x15f [0x57705f] *** v3 debug| 555de9: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'pool_mkpool+0x449 [0x555de9] *** v3 debug| 555eab: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'pool_poolherder+0x6b [0x555eab] *** v3 debug| fffffc7fef2456e7: /lib/amd64/libc.so.1'_thrp_setup+0x77 [0xfffffc7fef2456e7] *** v3 debug| fffffc7fef245a30: /lib/amd64/libc.so.1'_lwp_start+0x0 [0xfffffc7fef245a30] *** v3 debug|errno = 11 (Resource temporarily unavailable) *** v3 debug|argv = { *** v3 debug| [0] = \"vinyld\", *** v3 debug| [1] = \"-d\", *** v3 debug| [2] = \"-n\", *** v3 debug| [3] = \"/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v3\", *** v3 debug| [4] = \"-i\", *** v3 debug| [5] = \"v3\", *** v3 debug| [6] = \"-l\", *** v3 debug| [7] = \"2m\", *** v3 debug| [8] = \"-p\", *** v3 debug| [9] = \" *** v3 debug|auto_restart=off\", *** v3 debug| [10] = \"-p\", *** v3 debug| [11] = \"syslog_cli_traffic=off\", *** v3 debug| [12] = \"-p\", *** v3 debug| [13] = \"thread_pool_min=10\", *** v3 debug| [14] = \"-p\", *** v3 debug| [15] = \"debug=+vtc_mode\", *** v3 debug| [16] = \"-p\", *** v3 debug| [17] = \"vsl_mask=+Debug,+H2RxHdr,+H2RxBody\", *** v3 debug| [18] = \"-p\", *** v3 debug| [19] = \"h2_initial_window_size=1m\", *** v3 debug| [20] = \"-p\", *** v3 debug| [21] = \"h2_rx_window_low_water=64k\", *** v3 debug| [22] = \"-a\", *** v3 debug| [23] = \"127.0.0.1:0\", *** v3 debug| [24] = \"-M\", *** v3 debug| [25] = \"127.0.0.1 47808\", *** v3 debug| [26] = \"-P\", *** v3 debug| [27] = \"/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v3/vinyld.pid\", *** v3 debug| [28] = \"-p\", *** v3 debug| [29] = \"vmod_path=/tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/vmod/.libs:\", *** v3 debug|} *** v3 debug|pthread.self = 4 *** v3 debug|pthread.name = (pool_poolherder) *** v3 debug|thr.req = NULL *** v3 debug|thr.busyobj = NULL *** v3 debug|thr.worker = NULL *** v3 debug|vmods = { *** v3 debug|}, *** v3 debug|pools = { *** v3 debug|}, *** v3 debug| *** v3 debug| *** v3 debug|Debug: Child cleanup complete *** v3 CLI RX 400 **** v3 CLI RX|Child failed on launch **** dT 2.908 ---- v3 CLI start command failed: 400 Child failed on launch * top RESETTING after ../../../../bin/vinyltest/tests/b00053.vtc ** s1 Waiting for server (3/-1) ** v1 Wait **** v1 CLI TX|panic.show *** v1 CLI RX 300 **** v1 CLI RX|Child has not panicked or panic has been cleared **** dT 2.909 *** v1 debug|Info: manager stopping child *** v1 debug|Debug: *** v1 debug| Stopping Child **** dT 2.958 **** v1 vsl| 0 CLI - EOF on CLI connection, worker stops **** dT 3.009 *** v1 debug|Info: Child (890946) said Child dies *** v1 debug|Info: *** v1 debug| *** v1 debug|Child (890946) ended *** v1 debug| *** v1 debug|Debug: *** v1 debug|Child cleanup complete *** v1 debug|Info: *** v1 debug|manager dies **** dT 3.010 **** v1 STDOUT EOF **** dT 3.059 ** v1 WAIT4 pid=890894 status=0x0000 (user 0.665000 sys 0.251000) **** dT 3.060 ** v2 Wait **** v2 CLI TX|panic.show *** v2 CLI RX 300 **** v2 CLI RX|Child has not panicked or panic has been cleared *** v2 debug|Info: *** v2 debug| manager stopping child *** v2 debug|Debug: Stopping Child **** dT 3.071 **** v2 vsl| 0 CLI - EOF on CLI connection, worker stops **** dT 3.160 *** v2 debug|Info: Child (891025) said Child dies *** v2 debug|Info: Child (891025) ended *** v2 debug|Debug: *** v2 debug|Child cleanup complete *** v2 debug|Info: manager dies *** v2 debug| **** dT 3.161 **** v2 STDOUT EOF **** dT 3.171 ** v2 WAIT4 pid=890954 status=0x0000 (user 1.362000 sys 0.458000) ** v3 Wait **** v3 CLI TX|panic.show *** v3 CLI RX 200 **** dT 3.172 **** v3 CLI RX|Child (891363) Panic at: Tue, 17 Mar 2026 11:26:39 GMT **** v3 CLI RX|Wrong turn at ../../../../bin/vinyld/cache/cache_mempool.c:246: **** v3 CLI RX|pthread_create(&mpl->thread, ((void *)0), mpl_guard, mpl) failed **** v3 CLI RX|version = vinyl-cache-trunk revision 17ea9216e8091e8bd4b65a2e0b946c92de2125f4, vrt api = 23.0 **** v3 CLI RX|ident = -jsolaris,-sdefault,-sdefault,-hcritbit,ports **** v3 CLI RX|now = 5958876.071344 (mono), 1773746799.016978 (real) **** v3 CLI RX|Backtrace: **** v3 CLI RX| 6d5a7d: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'vbt_execinfo+0x3d [0x6d5a7d] **** v3 CLI RX| 6d5e21: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'VBT_format+0x41 [0x6d5e21] **** v3 CLI RX| 5539f8: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'pan_backtrace+0x58 [0x5539f8] **** v3 CLI RX| 5540f9: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'pan_ic+0x539 [0x5540f9] **** v3 CLI RX| 6d43ac: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'VAS_Fail+0x8c [0x6d43ac] **** v3 CLI RX| 545ed2: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'MPL_New+0x332 [0x545ed2] **** v3 CLI RX| 57705f: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'SES_NewPool+0x15f [0x57705f] **** v3 CLI RX| 555de9: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'pool_mkpool+0x449 [0x555de9] **** v3 CLI RX| 555eab: /tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyld/vinyld'pool_poolherder+0x6b [0x555eab] **** v3 CLI RX| fffffc7fef2456e7: /lib/amd64/libc.so.1'_thrp_setup+0x77 [0xfffffc7fef2456e7] **** v3 CLI RX| fffffc7fef245a30: /lib/amd64/libc.so.1'_lwp_start+0x0 [0xfffffc7fef245a30] **** v3 CLI RX|errno = 11 (Resource temporarily unavailable) **** v3 CLI RX|argv = { **** v3 CLI RX| [0] = \"vinyld\", **** v3 CLI RX| [1] = \"-d\", **** v3 CLI RX| [2] = \"-n\", **** v3 CLI RX| [3] = \"/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v3\", **** v3 CLI RX| [4] = \"-i\", **** v3 CLI RX| [5] = \"v3\", **** v3 CLI RX| [6] = \"-l\", **** v3 CLI RX| [7] = \"2m\", **** v3 CLI RX| [8] = \"-p\", **** v3 CLI RX| [9] = \"auto_restart=off\", **** v3 CLI RX| [10] = \"-p\", **** v3 CLI RX| [11] = \"syslog_cli_traffic=off\", **** v3 CLI RX| [12] = \"-p\", **** v3 CLI RX| [13] = \"thread_pool_min=10\", **** v3 CLI RX| [14] = \"-p\", **** v3 CLI RX| [15] = \"debug=+vtc_mode\", **** v3 CLI RX| [16] = \"-p\", **** v3 CLI RX| [17] = \"vsl_mask=+Debug,+H2RxHdr,+H2RxBody\", **** v3 CLI RX| [18] = \"-p\", **** v3 CLI RX| [19] = \"h2_initial_window_size=1m\", **** v3 CLI RX| [20] = \"-p\", **** v3 CLI RX| [21] = \"h2_rx_window_low_water=64k\", **** v3 CLI RX| [22] = \"-a\", **** v3 CLI RX| [23] = \"127.0.0.1:0\", **** v3 CLI RX| [24] = \"-M\", **** v3 CLI RX| [25] = \"127.0.0.1 47808\", **** v3 CLI RX| [26] = \"-P\", **** v3 CLI RX| [27] = \"/tmp/vtest.suncc12.6_m64_IPv4_uffix/_vtest_tmp/vtc.890877.3d50f667/v3/vinyld.pid\", **** v3 CLI RX| [28] = \"-p\", **** v3 CLI RX| [29] = \"vmod_path=/tmp/vtest.suncc12.6_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/vmod/.libs:\", **** v3 CLI RX|} **** v3 CLI RX|pthread.self = 4 **** v3 CLI RX|pthread.name = (pool_poolherder) **** v3 CLI RX|thr.req = NULL **** v3 CLI RX|thr.busyobj = NULL **** v3 CLI RX|thr.worker = NULL **** v3 CLI RX|vmods = { **** v3 CLI RX|}, **** v3 CLI RX|pools = { **** v3 CLI RX|}, **** v3 CLI RX| **** v3 CLI RX| **** dT 3.182 ---- v3 Unexpected panic * top failure during reset # top TEST ../../../../bin/vinyltest/tests/b00053.vtc FAILED (3.187) exit=2 FAIL tests/b00053.vtc (exit status: 2)